[INFO] cloning repository https://github.com/ErvinXie/xwytools
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/ErvinXie/xwytools" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FErvinXie%2Fxwytools", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FErvinXie%2Fxwytools'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] ba74b36e6b1615ab7d8b7c27b170e498246b1358
[INFO] testing ErvinXie/xwytools/ba74b36e6b1615ab7d8b7c27b170e498246b1358 against master#f9988fefd3add01f414f52b414308e7872622fee for pr-155114
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FErvinXie%2Fxwytools" "/workspace/builds/worker-0-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-0-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/ErvinXie/xwytools
[INFO] finished tweaking git repo https://github.com/ErvinXie/xwytools
[INFO] tweaked toml for git repo https://github.com/ErvinXie/xwytools written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/ErvinXie/xwytools on toolchain f9988fefd3add01f414f52b414308e7872622fee
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/ErvinXie/xwytools already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded is-terminal v0.4.10
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 7ae6b083a93d154c7c17586bf80a1aff1e06cd77fe45c49801820548854bc6a1
[INFO] running `Command { std: "docker" "start" "-a" "7ae6b083a93d154c7c17586bf80a1aff1e06cd77fe45c49801820548854bc6a1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7ae6b083a93d154c7c17586bf80a1aff1e06cd77fe45c49801820548854bc6a1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7ae6b083a93d154c7c17586bf80a1aff1e06cd77fe45c49801820548854bc6a1", kill_on_drop: false }`
[INFO] [stdout] 7ae6b083a93d154c7c17586bf80a1aff1e06cd77fe45c49801820548854bc6a1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 245f6bcde4045101bf10ddfe74843b07fc4e8fde7cee497a62743bfb48452949
[INFO] running `Command { std: "docker" "start" "-a" "245f6bcde4045101bf10ddfe74843b07fc4e8fde7cee497a62743bfb48452949", kill_on_drop: false }`
[INFO] [stderr]    Compiling crossbeam-utils v0.8.19
[INFO] [stderr]    Compiling bitflags v2.4.2
[INFO] [stderr]    Compiling rustix v0.38.30
[INFO] [stderr]    Compiling memchr v2.7.1
[INFO] [stderr]    Compiling libc v0.2.152
[INFO] [stderr]    Compiling regex-syntax v0.8.2
[INFO] [stderr]    Compiling linux-raw-sys v0.4.13
[INFO] [stderr]    Compiling log v0.4.20
[INFO] [stderr]    Compiling iana-time-zone v0.1.59
[INFO] [stderr]    Compiling number_prefix v0.4.0
[INFO] [stderr]    Compiling num-traits v0.2.17
[INFO] [stderr]    Compiling aho-corasick v1.1.2
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.18
[INFO] [stderr]    Compiling crossbeam-channel v0.5.11
[INFO] [stderr]    Compiling crossbeam-queue v0.3.11
[INFO] [stderr]    Compiling crossbeam-deque v0.8.5
[INFO] [stderr]    Compiling is-terminal v0.4.10
[INFO] [stderr]    Compiling crossbeam v0.8.4
[INFO] [stderr]    Compiling chrono v0.4.31
[INFO] [stderr]    Compiling regex-automata v0.4.3
[INFO] [stderr]    Compiling nix v0.27.1
[INFO] [stderr]    Compiling ctrlc v3.4.2
[INFO] [stderr]    Compiling regex v1.10.2
[INFO] [stderr]    Compiling env_logger v0.10.2
[INFO] [stderr]    Compiling xwytools v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 8.48s
[INFO] running `Command { std: "docker" "inspect" "245f6bcde4045101bf10ddfe74843b07fc4e8fde7cee497a62743bfb48452949", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "245f6bcde4045101bf10ddfe74843b07fc4e8fde7cee497a62743bfb48452949", kill_on_drop: false }`
[INFO] [stdout] 245f6bcde4045101bf10ddfe74843b07fc4e8fde7cee497a62743bfb48452949
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a3c6971cc7d7f387f7d1991ca97813eb19d6b2c64b6bcace6331d5e9d0b066c1
[INFO] running `Command { std: "docker" "start" "-a" "a3c6971cc7d7f387f7d1991ca97813eb19d6b2c64b6bcace6331d5e9d0b066c1", kill_on_drop: false }`
[INFO] [stderr]    Compiling xwytools v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `std::time::Instant`
[INFO] [stdout]    --> src/bench.rs:123:9
[INFO] [stdout]     |
[INFO] [stdout] 123 |     use std::time::Instant;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.88s
[INFO] running `Command { std: "docker" "inspect" "a3c6971cc7d7f387f7d1991ca97813eb19d6b2c64b6bcace6331d5e9d0b066c1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a3c6971cc7d7f387f7d1991ca97813eb19d6b2c64b6bcace6331d5e9d0b066c1", kill_on_drop: false }`
[INFO] [stdout] a3c6971cc7d7f387f7d1991ca97813eb19d6b2c64b6bcace6331d5e9d0b066c1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] d1977b8ab976171b555a1217b4c7209564efbed2f0180cbbb9ad2e39ec4dd93e
[INFO] running `Command { std: "docker" "start" "-a" "d1977b8ab976171b555a1217b4c7209564efbed2f0180cbbb9ad2e39ec4dd93e", kill_on_drop: false }`
[INFO] [stderr] warning: unused import: `std::time::Instant`
[INFO] [stderr]    --> src/bench.rs:123:9
[INFO] [stderr]     |
[INFO] [stderr] 123 |     use std::time::Instant;
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `xwytools` (lib test) generated 1 warning (run `cargo fix --lib -p xwytools --tests` to apply 1 suggestion)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/xwytools-64bef4a102edbd44)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr] [2026-05-04T00:10:29Z INFO  xwytools::bench] Starting bench with concurrency 100
[INFO] [stderr] [2026-05-04T00:10:35Z INFO  xwytools::bench] Throughput(op/s): last 106.7M, ave 26.1M
[INFO] [stderr] [2026-05-04T00:10:39Z INFO  xwytools::bench] Throughput(op/s): last 102.7M, ave 24.7M
[INFO] [stderr] [2026-05-04T00:10:44Z INFO  xwytools::bench] Throughput(op/s): last 72.3M, ave 21.5M
[INFO] [stderr] [2026-05-04T00:10:48Z INFO  xwytools::bench] Throughput(op/s): last 62.0M, ave 20.2M
[INFO] [stderr] [2026-05-04T00:10:52Z INFO  xwytools::bench] Throughput(op/s): last 64.5M, ave 19.8M
[INFO] [stderr] [2026-05-04T00:10:57Z INFO  xwytools::bench] Throughput(op/s): last 55.4M, ave 18.0M
[INFO] [stderr] [2026-05-04T00:11:02Z INFO  xwytools::bench] Throughput(op/s): last 86.1M, ave 17.6M
[INFO] [stderr] [2026-05-04T00:11:08Z INFO  xwytools::bench] Throughput(op/s): last 90.8M, ave 17.4M
[INFO] [stderr] [2026-05-04T00:11:13Z INFO  xwytools::bench] Throughput(op/s): last 122.6M, ave 18.2M
[INFO] [stderr] [2026-05-04T00:11:16Z INFO  xwytools::bench] Throughput(op/s): last 119.4M, ave 19.4M
[INFO] [stderr] [2026-05-04T00:11:22Z INFO  xwytools::bench] Throughput(op/s): last 112.6M, ave 19.6M
[INFO] [stderr] [2026-05-04T00:11:27Z INFO  xwytools::bench] Throughput(op/s): last 115.7M, ave 19.9M
[INFO] [stdout] test bench::test::test_bench has been running for over 60 seconds
[INFO] [stderr] [2026-05-04T00:11:31Z INFO  xwytools::bench] Throughput(op/s): last 90.2M, ave 20.1M
[INFO] [stderr] [2026-05-04T00:11:35Z INFO  xwytools::bench] Throughput(op/s): last 120.4M, ave 20.5M
[INFO] [stderr] [2026-05-04T00:11:39Z INFO  xwytools::bench] Throughput(op/s): last 96.3M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:11:43Z INFO  xwytools::bench] Throughput(op/s): last 114.4M, ave 21.3M
[INFO] [stderr] [2026-05-04T00:11:46Z INFO  xwytools::bench] Throughput(op/s): last 104.5M, ave 21.9M
[INFO] [stderr] [2026-05-04T00:11:50Z INFO  xwytools::bench] Throughput(op/s): last 116.0M, ave 22.3M
[INFO] [stderr] [2026-05-04T00:11:52Z INFO  xwytools::bench] Throughput(op/s): last 93.8M, ave 22.8M
[INFO] [stderr] [2026-05-04T00:11:55Z INFO  xwytools::bench] Throughput(op/s): last 98.7M, ave 23.1M
[INFO] [stderr] [2026-05-04T00:11:57Z INFO  xwytools::bench] Throughput(op/s): last 70.5M, ave 23.3M
[INFO] [stderr] [2026-05-04T00:12:00Z INFO  xwytools::bench] Throughput(op/s): last 51.7M, ave 23.3M
[INFO] [stderr] [2026-05-04T00:12:02Z INFO  xwytools::bench] Throughput(op/s): last 65.2M, ave 23.5M
[INFO] [stderr] [2026-05-04T00:12:06Z INFO  xwytools::bench] Throughput(op/s): last 82.2M, ave 23.3M
[INFO] [stderr] [2026-05-04T00:12:09Z INFO  xwytools::bench] Throughput(op/s): last 85.0M, ave 23.4M
[INFO] [stderr] [2026-05-04T00:12:13Z INFO  xwytools::bench] Throughput(op/s): last 87.8M, ave 23.5M
[INFO] [stderr] [2026-05-04T00:12:17Z INFO  xwytools::bench] Throughput(op/s): last 75.8M, ave 23.3M
[INFO] [stderr] [2026-05-04T00:12:21Z INFO  xwytools::bench] Throughput(op/s): last 98.6M, ave 23.3M
[INFO] [stderr] [2026-05-04T00:12:28Z INFO  xwytools::bench] Throughput(op/s): last 117.7M, ave 23.0M
[INFO] [stderr] [2026-05-04T00:12:34Z INFO  xwytools::bench] Throughput(op/s): last 109.9M, ave 22.7M
[INFO] [stderr] [2026-05-04T00:12:36Z INFO  xwytools::bench] Throughput(op/s): last 87.9M, ave 23.0M
[INFO] [stderr] [2026-05-04T00:12:41Z INFO  xwytools::bench] Throughput(op/s): last 75.2M, ave 22.7M
[INFO] [stderr] [2026-05-04T00:12:46Z INFO  xwytools::bench] Throughput(op/s): last 100.6M, ave 22.5M
[INFO] [stderr] [2026-05-04T00:12:52Z INFO  xwytools::bench] Throughput(op/s): last 78.0M, ave 22.2M
[INFO] [stderr] [2026-05-04T00:12:57Z INFO  xwytools::bench] Throughput(op/s): last 74.7M, ave 22.0M
[INFO] [stderr] [2026-05-04T00:13:02Z INFO  xwytools::bench] Throughput(op/s): last 75.5M, ave 21.7M
[INFO] [stderr] [2026-05-04T00:13:05Z INFO  xwytools::bench] Throughput(op/s): last 77.8M, ave 21.8M
[INFO] [stderr] [2026-05-04T00:13:10Z INFO  xwytools::bench] Throughput(op/s): last 59.0M, ave 21.5M
[INFO] [stderr] [2026-05-04T00:13:15Z INFO  xwytools::bench] Throughput(op/s): last 98.4M, ave 21.5M
[INFO] [stderr] [2026-05-04T00:13:20Z INFO  xwytools::bench] Throughput(op/s): last 93.7M, ave 21.4M
[INFO] [stderr] [2026-05-04T00:13:25Z INFO  xwytools::bench] Throughput(op/s): last 90.9M, ave 21.3M
[INFO] [stderr] [2026-05-04T00:13:31Z INFO  xwytools::bench] Throughput(op/s): last 105.8M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:13:35Z INFO  xwytools::bench] Throughput(op/s): last 132.7M, ave 21.4M
[INFO] [stderr] [2026-05-04T00:13:39Z INFO  xwytools::bench] Throughput(op/s): last 125.4M, ave 21.6M
[INFO] [stderr] [2026-05-04T00:13:44Z INFO  xwytools::bench] Throughput(op/s): last 102.9M, ave 21.6M
[INFO] [stderr] [2026-05-04T00:13:48Z INFO  xwytools::bench] Throughput(op/s): last 81.3M, ave 21.6M
[INFO] [stderr] [2026-05-04T00:13:51Z INFO  xwytools::bench] Throughput(op/s): last 71.2M, ave 21.6M
[INFO] [stderr] [2026-05-04T00:13:57Z INFO  xwytools::bench] Throughput(op/s): last 112.8M, ave 21.5M
[INFO] [stderr] [2026-05-04T00:14:02Z INFO  xwytools::bench] Throughput(op/s): last 138.2M, ave 21.7M
[INFO] [stderr] [2026-05-04T00:14:04Z INFO  xwytools::bench] Throughput(op/s): last 67.1M, ave 21.8M
[INFO] [stderr] [2026-05-04T00:14:09Z INFO  xwytools::bench] Throughput(op/s): last 89.0M, ave 21.7M
[INFO] [stderr] [2026-05-04T00:14:14Z INFO  xwytools::bench] Throughput(op/s): last 86.0M, ave 21.6M
[INFO] [stderr] [2026-05-04T00:14:19Z INFO  xwytools::bench] Throughput(op/s): last 102.4M, ave 21.5M
[INFO] [stderr] [2026-05-04T00:14:26Z INFO  xwytools::bench] Throughput(op/s): last 97.6M, ave 21.3M
[INFO] [stderr] [2026-05-04T00:14:33Z INFO  xwytools::bench] Throughput(op/s): last 107.3M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:14:39Z INFO  xwytools::bench] Throughput(op/s): last 131.8M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:14:43Z INFO  xwytools::bench] Throughput(op/s): last 98.2M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:14:48Z INFO  xwytools::bench] Throughput(op/s): last 80.0M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:14:53Z INFO  xwytools::bench] Throughput(op/s): last 98.5M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:14:57Z INFO  xwytools::bench] Throughput(op/s): last 94.9M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:15:01Z INFO  xwytools::bench] Throughput(op/s): last 98.5M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:15:06Z INFO  xwytools::bench] Throughput(op/s): last 89.3M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:15:10Z INFO  xwytools::bench] Throughput(op/s): last 102.5M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:15:16Z INFO  xwytools::bench] Throughput(op/s): last 116.3M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:15:20Z INFO  xwytools::bench] Throughput(op/s): last 75.6M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:15:24Z INFO  xwytools::bench] Throughput(op/s): last 70.3M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:15:28Z INFO  xwytools::bench] Throughput(op/s): last 50.7M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:15:33Z INFO  xwytools::bench] Throughput(op/s): last 89.6M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:15:37Z INFO  xwytools::bench] Throughput(op/s): last 84.9M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:15:40Z INFO  xwytools::bench] Throughput(op/s): last 84.8M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:15:44Z INFO  xwytools::bench] Throughput(op/s): last 82.9M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:15:49Z INFO  xwytools::bench] Throughput(op/s): last 109.1M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:15:55Z INFO  xwytools::bench] Throughput(op/s): last 106.1M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:15:58Z INFO  xwytools::bench] Throughput(op/s): last 76.2M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:16:03Z INFO  xwytools::bench] Throughput(op/s): last 67.0M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:16:08Z INFO  xwytools::bench] Throughput(op/s): last 115.5M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:16:13Z INFO  xwytools::bench] Throughput(op/s): last 103.5M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:16:19Z INFO  xwytools::bench] Throughput(op/s): last 109.6M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:16:23Z INFO  xwytools::bench] Throughput(op/s): last 126.0M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:16:25Z INFO  xwytools::bench] Throughput(op/s): last 87.0M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:16:27Z INFO  xwytools::bench] Throughput(op/s): last 57.3M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:16:30Z INFO  xwytools::bench] Throughput(op/s): last 48.5M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:16:33Z INFO  xwytools::bench] Throughput(op/s): last 41.3M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:16:36Z INFO  xwytools::bench] Throughput(op/s): last 40.7M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:16:39Z INFO  xwytools::bench] Throughput(op/s): last 49.5M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:16:43Z INFO  xwytools::bench] Throughput(op/s): last 42.8M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:16:46Z INFO  xwytools::bench] Throughput(op/s): last 43.0M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:16:51Z INFO  xwytools::bench] Throughput(op/s): last 56.7M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:16:56Z INFO  xwytools::bench] Throughput(op/s): last 108.8M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:16:59Z INFO  xwytools::bench] Throughput(op/s): last 77.9M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:17:03Z INFO  xwytools::bench] Throughput(op/s): last 70.7M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:17:07Z INFO  xwytools::bench] Throughput(op/s): last 65.3M, ave 20.6M
[INFO] [stderr] [2026-05-04T00:17:13Z INFO  xwytools::bench] Throughput(op/s): last 102.2M, ave 20.6M
[INFO] [stderr] [2026-05-04T00:17:17Z INFO  xwytools::bench] Throughput(op/s): last 72.4M, ave 20.6M
[INFO] [stderr] [2026-05-04T00:17:22Z INFO  xwytools::bench] Throughput(op/s): last 99.8M, ave 20.6M
[INFO] [stderr] [2026-05-04T00:17:26Z INFO  xwytools::bench] Throughput(op/s): last 111.9M, ave 20.6M
[INFO] [stderr] [2026-05-04T00:17:29Z INFO  xwytools::bench] Throughput(op/s): last 92.1M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:17:34Z INFO  xwytools::bench] Throughput(op/s): last 81.0M, ave 20.6M
[INFO] [stderr] [2026-05-04T00:17:39Z INFO  xwytools::bench] Throughput(op/s): last 99.5M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:17:43Z INFO  xwytools::bench] Throughput(op/s): last 123.7M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:17:48Z INFO  xwytools::bench] Throughput(op/s): last 103.7M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:17:52Z INFO  xwytools::bench] Throughput(op/s): last 92.2M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:17:56Z INFO  xwytools::bench] Throughput(op/s): last 85.2M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:18:02Z INFO  xwytools::bench] Throughput(op/s): last 89.7M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:18:07Z INFO  xwytools::bench] Throughput(op/s): last 107.5M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:18:11Z INFO  xwytools::bench] Throughput(op/s): last 118.1M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:18:15Z INFO  xwytools::bench] Throughput(op/s): last 94.8M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:18:19Z INFO  xwytools::bench] Throughput(op/s): last 93.1M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:18:23Z INFO  xwytools::bench] Throughput(op/s): last 87.2M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:18:26Z INFO  xwytools::bench] Throughput(op/s): last 61.6M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:18:32Z INFO  xwytools::bench] Throughput(op/s): last 110.2M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:18:36Z INFO  xwytools::bench] Throughput(op/s): last 93.2M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:18:40Z INFO  xwytools::bench] Throughput(op/s): last 85.9M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:18:44Z INFO  xwytools::bench] Throughput(op/s): last 99.2M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:18:48Z INFO  xwytools::bench] Throughput(op/s): last 74.9M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:18:54Z INFO  xwytools::bench] Throughput(op/s): last 114.2M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:19:00Z INFO  xwytools::bench] Throughput(op/s): last 112.3M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:19:03Z INFO  xwytools::bench] Throughput(op/s): last 97.2M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:19:08Z INFO  xwytools::bench] Throughput(op/s): last 97.5M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:19:12Z INFO  xwytools::bench] Throughput(op/s): last 89.6M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:19:16Z INFO  xwytools::bench] Throughput(op/s): last 63.3M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:19:21Z INFO  xwytools::bench] Throughput(op/s): last 71.5M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:19:25Z INFO  xwytools::bench] Throughput(op/s): last 83.5M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:19:29Z INFO  xwytools::bench] Throughput(op/s): last 75.6M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:19:34Z INFO  xwytools::bench] Throughput(op/s): last 91.1M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:19:40Z INFO  xwytools::bench] Throughput(op/s): last 134.7M, ave 20.7M
[INFO] [stderr] [2026-05-04T00:19:43Z INFO  xwytools::bench] Throughput(op/s): last 115.0M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:19:46Z INFO  xwytools::bench] Throughput(op/s): last 75.2M, ave 20.8M
[INFO] [stderr] [2026-05-04T00:19:50Z INFO  xwytools::bench] Throughput(op/s): last 99.5M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:19:54Z INFO  xwytools::bench] Throughput(op/s): last 90.4M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:19:58Z INFO  xwytools::bench] Throughput(op/s): last 117.3M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:20:00Z INFO  xwytools::bench] Throughput(op/s): last 64.7M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:20:04Z INFO  xwytools::bench] Throughput(op/s): last 71.3M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:20:06Z INFO  xwytools::bench] Throughput(op/s): last 64.5M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:20:08Z INFO  xwytools::bench] Throughput(op/s): last 44.4M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:20:11Z INFO  xwytools::bench] Throughput(op/s): last 75.5M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:20:16Z INFO  xwytools::bench] Throughput(op/s): last 123.4M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:20:20Z INFO  xwytools::bench] Throughput(op/s): last 92.3M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:20:23Z INFO  xwytools::bench] Throughput(op/s): last 82.3M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:20:27Z INFO  xwytools::bench] Throughput(op/s): last 81.3M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:20:32Z INFO  xwytools::bench] Throughput(op/s): last 112.6M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:20:38Z INFO  xwytools::bench] Throughput(op/s): last 97.7M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:20:43Z INFO  xwytools::bench] Throughput(op/s): last 131.2M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:20:50Z INFO  xwytools::bench] Throughput(op/s): last 121.1M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:20:54Z INFO  xwytools::bench] Throughput(op/s): last 113.9M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:20:58Z INFO  xwytools::bench] Throughput(op/s): last 79.1M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:01Z INFO  xwytools::bench] Throughput(op/s): last 65.1M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:04Z INFO  xwytools::bench] Throughput(op/s): last 73.9M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:07Z INFO  xwytools::bench] Throughput(op/s): last 80.1M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:11Z INFO  xwytools::bench] Throughput(op/s): last 68.4M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:14Z INFO  xwytools::bench] Throughput(op/s): last 51.9M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:19Z INFO  xwytools::bench] Throughput(op/s): last 69.0M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:23Z INFO  xwytools::bench] Throughput(op/s): last 105.3M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:27Z INFO  xwytools::bench] Throughput(op/s): last 102.8M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:32Z INFO  xwytools::bench] Throughput(op/s): last 82.0M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:38Z INFO  xwytools::bench] Throughput(op/s): last 112.1M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:42Z INFO  xwytools::bench] Throughput(op/s): last 117.3M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:46Z INFO  xwytools::bench] Throughput(op/s): last 106.3M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:21:50Z INFO  xwytools::bench] Throughput(op/s): last 103.8M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:21:54Z INFO  xwytools::bench] Throughput(op/s): last 115.9M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:21:58Z INFO  xwytools::bench] Throughput(op/s): last 110.4M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:03Z INFO  xwytools::bench] Throughput(op/s): last 103.8M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:09Z INFO  xwytools::bench] Throughput(op/s): last 106.1M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:13Z INFO  xwytools::bench] Throughput(op/s): last 89.7M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:18Z INFO  xwytools::bench] Throughput(op/s): last 69.4M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:20Z INFO  xwytools::bench] Throughput(op/s): last 80.7M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:25Z INFO  xwytools::bench] Throughput(op/s): last 82.0M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:32Z INFO  xwytools::bench] Throughput(op/s): last 129.7M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:36Z INFO  xwytools::bench] Throughput(op/s): last 107.0M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:39Z INFO  xwytools::bench] Throughput(op/s): last 70.3M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:41Z INFO  xwytools::bench] Throughput(op/s): last 48.0M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:44Z INFO  xwytools::bench] Throughput(op/s): last 47.7M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:22:47Z INFO  xwytools::bench] Throughput(op/s): last 42.2M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:22:53Z INFO  xwytools::bench] Throughput(op/s): last 89.7M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:22:56Z INFO  xwytools::bench] Throughput(op/s): last 102.4M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:23:01Z INFO  xwytools::bench] Throughput(op/s): last 101.7M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:23:06Z INFO  xwytools::bench] Throughput(op/s): last 121.6M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:23:12Z INFO  xwytools::bench] Throughput(op/s): last 93.0M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:23:17Z INFO  xwytools::bench] Throughput(op/s): last 116.8M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:23:22Z INFO  xwytools::bench] Throughput(op/s): last 98.6M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:23:26Z INFO  xwytools::bench] Throughput(op/s): last 108.9M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:23:31Z INFO  xwytools::bench] Throughput(op/s): last 86.7M, ave 21.2M
[INFO] [stderr] [2026-05-04T00:23:37Z INFO  xwytools::bench] Throughput(op/s): last 106.2M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:23:44Z INFO  xwytools::bench] Throughput(op/s): last 121.3M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:23:48Z INFO  xwytools::bench] Throughput(op/s): last 70.9M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:23:53Z INFO  xwytools::bench] Throughput(op/s): last 57.6M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:23:57Z INFO  xwytools::bench] Throughput(op/s): last 59.5M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:23:59Z INFO  xwytools::bench] Throughput(op/s): last 39.9M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:24:03Z INFO  xwytools::bench] Throughput(op/s): last 42.8M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:24:06Z INFO  xwytools::bench] Throughput(op/s): last 41.1M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:24:11Z INFO  xwytools::bench] Throughput(op/s): last 85.1M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:24:14Z INFO  xwytools::bench] Throughput(op/s): last 85.3M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:24:19Z INFO  xwytools::bench] Throughput(op/s): last 74.0M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:24:22Z INFO  xwytools::bench] Throughput(op/s): last 75.6M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:24:25Z INFO  xwytools::bench] Throughput(op/s): last 104.7M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:24:31Z INFO  xwytools::bench] Throughput(op/s): last 115.5M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:24:34Z INFO  xwytools::bench] Throughput(op/s): last 97.8M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:24:38Z INFO  xwytools::bench] Throughput(op/s): last 85.2M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:24:43Z INFO  xwytools::bench] Throughput(op/s): last 93.1M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:24:47Z INFO  xwytools::bench] Throughput(op/s): last 77.2M, ave 20.9M
[INFO] [stderr] [2026-05-04T00:24:51Z INFO  xwytools::bench] Throughput(op/s): last 107.7M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:24:55Z INFO  xwytools::bench] Throughput(op/s): last 89.5M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:24:58Z INFO  xwytools::bench] Throughput(op/s): last 98.6M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:25:03Z INFO  xwytools::bench] Throughput(op/s): last 106.5M, ave 21.0M
[INFO] [stderr] [2026-05-04T00:25:06Z INFO  xwytools::bench] Throughput(op/s): last 107.0M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:25:09Z INFO  xwytools::bench] Throughput(op/s): last 66.4M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:25:12Z INFO  xwytools::bench] Throughput(op/s): last 92.6M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:25:18Z INFO  xwytools::bench] Throughput(op/s): last 88.3M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:25:22Z INFO  xwytools::bench] Throughput(op/s): last 112.4M, ave 21.1M
[INFO] [stderr] [2026-05-04T00:25:25Z INFO  xwytools::bench] Throughput(op/s): last 83.9M, ave 21.1M
[ERROR] error running command: command timed out after 900 seconds
[INFO] running `Command { std: "docker" "inspect" "d1977b8ab976171b555a1217b4c7209564efbed2f0180cbbb9ad2e39ec4dd93e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d1977b8ab976171b555a1217b4c7209564efbed2f0180cbbb9ad2e39ec4dd93e", kill_on_drop: false }`
[INFO] [stdout] d1977b8ab976171b555a1217b4c7209564efbed2f0180cbbb9ad2e39ec4dd93e
